[INFO] cloning repository https://github.com/takemo101/sine-mml
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/takemo101/sine-mml" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Ftakemo101%2Fsine-mml", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Ftakemo101%2Fsine-mml'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 6ee015b3eab397ed1e9d748a60692d9bfc425dc9
[INFO] testing takemo101/sine-mml against try#0abe69e6c16114311cc2700e972492e64d69c28f for pr-154065
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Ftakemo101%2Fsine-mml" "/workspace/builds/worker-7-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-7-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/takemo101/sine-mml
[INFO] finished tweaking git repo https://github.com/takemo101/sine-mml
[INFO] tweaked toml for git repo https://github.com/takemo101/sine-mml written to /workspace/builds/worker-7-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/takemo101/sine-mml on toolchain 0abe69e6c16114311cc2700e972492e64d69c28f
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/takemo101/sine-mml already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] d41169065e226a694021a1e34ed60804b3e22f898462fc9675d9c2ac015e11a6
[INFO] running `Command { std: "docker" "start" "-a" "d41169065e226a694021a1e34ed60804b3e22f898462fc9675d9c2ac015e11a6", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "d41169065e226a694021a1e34ed60804b3e22f898462fc9675d9c2ac015e11a6", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d41169065e226a694021a1e34ed60804b3e22f898462fc9675d9c2ac015e11a6", kill_on_drop: false }`
[INFO] [stdout] d41169065e226a694021a1e34ed60804b3e22f898462fc9675d9c2ac015e11a6
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 8999d942ea86adbdb28d94247b67009458fd03c7ba808acf9e79ff4218805b25
[INFO] running `Command { std: "docker" "start" "-a" "8999d942ea86adbdb28d94247b67009458fd03c7ba808acf9e79ff4218805b25", kill_on_drop: false }`
[INFO] [stderr]    Compiling bytemuck v1.24.0
[INFO] [stderr]    Compiling libc v0.2.180
[INFO] [stderr]    Compiling arrayvec v0.7.6
[INFO] [stderr]    Compiling log v0.4.29
[INFO] [stderr]    Compiling encoding_rs v0.8.35
[INFO] [stderr]    Compiling proc-macro2 v1.0.105
[INFO] [stderr]    Compiling quote v1.0.43
[INFO] [stderr]    Compiling bitflags v2.10.0
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling alsa-sys v0.3.1
[INFO] [stderr]    Compiling symphonia-core v0.5.5
[INFO] [stderr]    Compiling zerocopy v0.8.33
[INFO] [stderr]    Compiling typenum v1.19.0
[INFO] [stderr]    Compiling find-msvc-tools v0.1.7
[INFO] [stderr]    Compiling rustix v0.38.44
[INFO] [stderr]    Compiling cc v1.2.52
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling nix v0.30.1
[INFO] [stderr]    Compiling syn v2.0.114
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling symphonia-metadata v0.5.5
[INFO] [stderr]    Compiling unicode-width v0.2.2
[INFO] [stderr]    Compiling symphonia-utils-xiph v0.5.5
[INFO] [stderr]    Compiling extended v0.1.0
[INFO] [stderr]    Compiling portable-atomic v1.13.0
[INFO] [stderr]    Compiling foldhash v0.2.0
[INFO] [stderr]    Compiling symphonia-format-riff v0.5.5
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling hashbrown v0.16.1
[INFO] [stderr]    Compiling libsqlite3-sys v0.36.0
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling nix v0.24.3
[INFO] [stderr]    Compiling ahash v0.8.12
[INFO] [stderr]    Compiling anstream v0.6.21
[INFO] [stderr]    Compiling symphonia-format-isomp4 v0.5.5
[INFO] [stderr]    Compiling hashbrown v0.14.5
[INFO] [stderr]    Compiling symphonia-format-ogg v0.5.5
[INFO] [stderr]    Compiling symphonia-format-mkv v0.5.5
[INFO] [stderr]    Compiling symphonia-codec-vorbis v0.5.5
[INFO] [stderr]    Compiling symphonia-bundle-flac v0.5.5
[INFO] [stderr]    Compiling generic-array v1.3.5
[INFO] [stderr]    Compiling symphonia-bundle-mp3 v0.5.5
[INFO] [stderr]    Compiling symphonia-format-caf v0.5.5
[INFO] [stderr]    Compiling symphonia-codec-aac v0.5.5
[INFO] [stderr]    Compiling symphonia-codec-alac v0.5.5
[INFO] [stderr]    Compiling symphonia-codec-pcm v0.5.5
[INFO] [stderr]    Compiling symphonia-codec-adpcm v0.5.5
[INFO] [stderr]    Compiling num-complex v0.4.6
[INFO] [stderr]    Compiling safe_arch v0.7.4
[INFO] [stderr]    Compiling glam v0.28.0
[INFO] [stderr]    Compiling dyn-clone v1.0.20
[INFO] [stderr]    Compiling clap_lex v0.7.6
[INFO] [stderr]    Compiling cpal v0.15.3
[INFO] [stderr]    Compiling tinyvec v1.10.0
[INFO] [stderr]    Compiling clap_builder v4.5.54
[INFO] [stderr]    Compiling dirs-sys v0.4.1
[INFO] [stderr]    Compiling anyhow v1.0.100
[INFO] [stderr]    Compiling microfft v0.6.0
[INFO] [stderr]    Compiling wide v0.7.33
[INFO] [stderr]    Compiling symphonia v0.5.5
[INFO] [stderr]    Compiling crossterm v0.28.1
[INFO] [stderr]    Compiling pin-project-internal v1.1.10
[INFO] [stderr]    Compiling thiserror-impl v1.0.69
[INFO] [stderr]    Compiling clap_derive v4.5.49
[INFO] [stderr]    Compiling numeric-array v0.6.1
[INFO] [stderr]    Compiling hashlink v0.11.0
[INFO] [stderr]    Compiling alsa v0.7.1
[INFO] [stderr]    Compiling alsa v0.9.1
[INFO] [stderr]    Compiling pin-project v1.1.10
[INFO] [stderr]    Compiling thingbuf v0.1.6
[INFO] [stderr]    Compiling funutd v0.16.0
[INFO] [stderr]    Compiling console v0.16.2
[INFO] [stderr]    Compiling fallible-iterator v0.3.0
[INFO] [stderr]    Compiling unit-prefix v0.5.2
[INFO] [stderr]    Compiling iana-time-zone v0.1.64
[INFO] [stderr]    Compiling chrono v0.4.42
[INFO] [stderr]    Compiling fundsp v0.18.2
[INFO] [stderr]    Compiling clap v4.5.54
[INFO] [stderr]    Compiling indicatif v0.18.3
[INFO] [stderr]    Compiling midir v0.9.1
[INFO] [stderr]    Compiling comfy-table v7.1.4
[INFO] [stderr]    Compiling thiserror v1.0.69
[INFO] [stderr]    Compiling ctrlc v3.5.1
[INFO] [stderr]    Compiling dirs v5.0.1
[INFO] [stderr]    Compiling hound v3.5.1
[INFO] [stderr]    Compiling rusqlite v0.38.0
[INFO] [stderr]    Compiling sine-mml v0.2.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 1m 33s
[INFO] running `Command { std: "docker" "inspect" "8999d942ea86adbdb28d94247b67009458fd03c7ba808acf9e79ff4218805b25", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8999d942ea86adbdb28d94247b67009458fd03c7ba808acf9e79ff4218805b25", kill_on_drop: false }`
[INFO] [stdout] 8999d942ea86adbdb28d94247b67009458fd03c7ba808acf9e79ff4218805b25
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] dc2728718eb4a3ad425ffdc3a8450daa31c21b79e331a955e8096519b6c44426
[INFO] running `Command { std: "docker" "start" "-a" "dc2728718eb4a3ad425ffdc3a8450daa31c21b79e331a955e8096519b6c44426", kill_on_drop: false }`
[INFO] [stderr]    Compiling regex-syntax v0.8.8
[INFO] [stderr]    Compiling rustix v1.1.3
[INFO] [stderr]    Compiling predicates-core v1.0.9
[INFO] [stderr]    Compiling float-cmp v0.10.0
[INFO] [stderr]    Compiling assert_cmd v2.1.2
[INFO] [stderr]    Compiling normalize-line-endings v0.3.0
[INFO] [stderr]    Compiling linux-raw-sys v0.11.0
[INFO] [stderr]    Compiling difflib v0.4.0
[INFO] [stderr]    Compiling termtree v0.5.1
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling wait-timeout v0.2.1
[INFO] [stderr]    Compiling predicates-tree v1.0.12
[INFO] [stderr]    Compiling tempfile v3.24.0
[INFO] [stderr]    Compiling regex-automata v0.4.13
[INFO] [stderr]    Compiling regex v1.12.2
[INFO] [stderr]    Compiling bstr v1.12.1
[INFO] [stderr]    Compiling predicates v3.1.3
[INFO] [stderr]    Compiling sine-mml v0.2.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 27.43s
[INFO] running `Command { std: "docker" "inspect" "dc2728718eb4a3ad425ffdc3a8450daa31c21b79e331a955e8096519b6c44426", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "dc2728718eb4a3ad425ffdc3a8450daa31c21b79e331a955e8096519b6c44426", kill_on_drop: false }`
[INFO] [stdout] dc2728718eb4a3ad425ffdc3a8450daa31c21b79e331a955e8096519b6c44426
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 033c8b2cea85627071f29d16388767120333dc1a7c193696fea5c18fb2afdb6c
[INFO] running `Command { std: "docker" "start" "-a" "033c8b2cea85627071f29d16388767120333dc1a7c193696fea5c18fb2afdb6c", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.33s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/sine_mml-0276c09564548178)
[INFO] [stdout] 
[INFO] [stdout] running 117 tests
[INFO] [stdout] test audio::waveform::tests::test_create_node ... ok
[INFO] [stdout] test audio::waveform::tests::test_midi_to_frequency ... ok
[INFO] [stdout] test audio::waveform::tests::test_waveform_types_exist ... ok
[INFO] [stdout] test cli::handlers::tests::test_export_handler_path_traversal ... ok
[INFO] [stdout] test cli::handlers::tests::test_clear_history_logic_cancel_no ... ok
[INFO] [stdout] test cli::handlers::tests::test_clear_history_logic_confirm_yes_uppercase ... ok
[INFO] [stdout] test cli::handlers::tests::test_export_logic_not_found ... ok
[INFO] [stderr] ALSA lib confmisc.c:855:(parse_card) cannot find card '0'
[INFO] [stdout] test cli::handlers::tests::test_clear_history_logic_empty ... ok
[INFO] [stderr] ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_card_inum returned error: No such file or directory
[INFO] [stdout] test audio::player::tests::test_player_lifecycle ... ok
[INFO] [stderr] ALSA lib confmisc.c:422:(snd_func_concat) error evaluating strings
[INFO] [stderr] ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
[INFO] [stderr] ALSA lib confmisc.c:1342:(snd_func_refer) error evaluating name
[INFO] [stderr] ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
[INFO] [stderr] ALSA lib conf.c:5727:(snd_config_expand) Evaluate error: No such file or directory
[INFO] [stderr] ALSA lib pcm.c:2721:(snd_pcm_open_noupdate) Unknown PCM default
[INFO] [stdout] test cli::handlers::tests::test_play_handler_no_input ... ok
[INFO] [stdout] test cli::handlers::tests::test_history_logic_with_note ... ok
[INFO] [stdout] test cli::handlers::tests::test_history_logic_with_data ... ok
[INFO] [stdout] test cli::handlers::tests::test_clear_history_logic_invalid_input ... ok
[INFO] [stdout] test cli::handlers::tests::test_should_save_flag_file_input ... ok
[INFO] [stdout] test cli::handlers::tests::test_history_logic_empty ... ok
[INFO] [stdout] test cli::handlers::tests::test_should_save_flag_history_id ... ok
[INFO] [stdout] test cli::handlers::tests::test_clear_history_logic_confirm_yes ... ok
[INFO] [stdout] test cli::handlers::tests::test_should_save_flag_no_history_false ... ok
[INFO] [stdout] test cli::handlers::tests::test_should_save_flag_mml_input ... ok
[INFO] [stdout] test cli::handlers::tests::test_play_handler_with_note_too_long ... ok
[INFO] [stdout] test cli::handlers::tests::test_should_save_flag_no_history_mml_input ... ok
[INFO] [stdout] test cli::handlers::tests::test_truncate_mml ... ok
[INFO] [stdout] test cli::output::tests::test_display_midi_progress_interrupt_immediately ... ok
[INFO] [stdout] test cli::handlers::tests::test_should_save_flag_no_history_file_input ... ok
[INFO] [stdout] test cli::output::tests::test_display_midi_progress_zero_duration ... ok
[INFO] [stdout] test cli::output::tests::test_error_does_not_panic ... ok
[INFO] [stdout] test cli::output::tests::test_info_does_not_panic ... ok
[INFO] [stdout] test cli::output::tests::test_success_does_not_panic ... ok
[INFO] [stdout] test cli::output::tests::test_create_export_progress_returns_valid_progressbar ... ok
[INFO] [stdout] test cli::output::tests::test_warning_does_not_panic ... ok
[INFO] [stdout] test db::history::tests::test_history_entry_new_with_empty_note ... ok
[INFO] [stdout] test db::history::tests::test_history_entry_new_with_note ... ok
[INFO] [stdout] test db::history::tests::test_history_entry_new_with_utf8_note ... ok
[INFO] [stdout] test db::history::tests::test_history_entry_new_without_note ... ok
[INFO] [stdout] test db::history::tests::test_waveform_as_str ... ok
[INFO] [stdout] test db::history::tests::test_waveform_from_str ... ok
[INFO] [stdout] test db::schema::tests::test_migrate_idempotent ... ok
[INFO] [stdout] test db::schema::tests::test_migrate_v1_to_v2 ... ok
[INFO] [stdout] test db::schema::tests::test_new_db_no_migration ... ok
[INFO] [stdout] test db::schema::tests::test_schema_creates_history_table ... ok
[INFO] [stdout] test db::schema::tests::test_note_null_allowed ... ok
[INFO] [stdout] test db::schema::tests::test_note_length_constraint ... ok
[INFO] [stdout] test db::tests::test_clear_all_empty_db ... ok
[INFO] [stdout] test db::schema::tests::test_schema_creates_schema_version_table ... ok
[INFO] [stdout] test db::tests::test_count_empty_db ... ok
[INFO] [stdout] test db::tests::test_count_with_entries ... ok
[INFO] [stdout] test db::tests::test_count_after_clear ... ok
[INFO] [stdout] test db::tests::test_get_by_id_not_found ... ok
[INFO] [stdout] test db::tests::test_get_db_path_returns_valid_path ... ok
[INFO] [stdout] test audio::exporter::tests::test_export_wav_success ... ok
[INFO] [stdout] test db::tests::test_clear_all_with_entries ... ok
[INFO] [stdout] test db::tests::test_database_init_creates_file ... ok
[INFO] [stdout] test db::tests::test_list_includes_note ... ok
[INFO] [stdout] test db::tests::test_save_and_get_by_id_with_note ... ok
[INFO] [stdout] test db::tests::test_list_with_limit ... ok
[INFO] [stdout] test db::tests::test_save_and_get_by_id ... ok
[INFO] [stdout] test db::tests::test_save_and_get_by_id_with_utf8_note ... ok
[INFO] [stdout] test db::tests::test_list_returns_descending_order ... ok
[INFO] [stdout] test db::tests::test_save_validation_bpm_out_of_range ... ok
[INFO] [stderr] ALSA lib seq_hw.c:528:(snd_seq_hw_open) open /dev/snd/seq failed: No such file or directory
[INFO] [stdout] test db::tests::test_save_validation_mml_empty ... ok
[INFO] [stderr] ALSA lib seq_hw.c:528:(snd_seq_hw_open) open /dev/snd/seq failed: No such file or directory
[INFO] [stdout] test midi::device::tests::test_connect_midi_device_invalid_id ... ok
[INFO] [stdout] test midi::device::tests::test_connect_midi_device_with_real_device ... ignored, requires MIDI hardware - run with `cargo test -- --ignored`
[INFO] [stderr] ALSA lib confmisc.c:855:(parse_card) cannot find card '0'
[INFO] [stderr] ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_card_inum returned error: No such file or directory
[INFO] [stderr] ALSA lib confmisc.c:422:(snd_func_concat) error evaluating strings
[INFO] [stderr] ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
[INFO] [stderr] ALSA lib confmisc.c:1342:(snd_func_refer) error evaluating name
[INFO] [stderr] ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
[INFO] [stderr] ALSA lib conf.c:5727:(snd_config_expand) Evaluate error: No such file or directory
[INFO] [stdout] test midi::device::tests::test_connect_midi_device_nonexistent_name ... ok
[INFO] [stdout] test cli::handlers::tests::test_play_handler_with_note ... ok
[INFO] [stderr] ALSA lib pcm.c:2721:(snd_pcm_open_noupdate) Unknown PCM default
[INFO] [stdout] test midi::device::tests::test_midi_device_info_clone ... ok
[INFO] [stdout] test midi::device::tests::test_midi_device_info_debug ... ok
[INFO] [stdout] test db::tests::test_wal_mode_enabled ... ok
[INFO] [stdout] test midi::device::tests::test_midi_device_info_new ... ok
[INFO] [stdout] test midi::device::tests::test_list_midi_devices_returns_result ... ok
[INFO] [stdout] test midi::device::tests::test_resolve_device_id_with_real_device ... ignored, requires MIDI hardware - run with `cargo test -- --ignored`
[INFO] [stderr] ALSA lib seq_hw.c:528:(snd_seq_hw_open) open /dev/snd/seq failed: No such file or directory
[INFO] [stderr] ALSA lib seq_hw.c:528:(snd_seq_hw_open) open /dev/snd/seq failed: No such file or directory
[INFO] [stderr] ALSA lib seq_hw.c:528:(snd_seq_hw_open) open /dev/snd/seq failed: No such file or directory
[INFO] [stdout] test midi::device::tests::test_resolve_device_id_nonexistent_name ... ok
[INFO] [stdout] test midi::device::tests::test_resolve_device_id_invalid_numeric ... ok
[INFO] [stdout] test midi::error::tests::test_connection_failed_display ... ok
[INFO] [stdout] test midi::error::tests::test_device_disconnected_display ... ok
[INFO] [stdout] test midi::error::tests::test_is_valid_channel_invalid_seventeen ... ok
[INFO] [stdout] test midi::error::tests::test_invalid_channel_display ... ok
[INFO] [stdout] test midi::error::tests::test_invalid_device_id_display ... ok
[INFO] [stdout] test midi::error::tests::test_is_valid_channel_invalid_zero ... ok
[INFO] [stdout] test midi::error::tests::test_no_device_found_display ... ok
[INFO] [stdout] test midi::error::tests::test_is_valid_channel_valid ... ok
[INFO] [stdout] test midi::error::tests::test_send_failed_display ... ok
[INFO] [stdout] test midi::error::tests::test_validate_channel_invalid ... ok
[INFO] [stdout] test mml::error::tests::display_empty_input ... ok
[INFO] [stdout] test midi::error::tests::test_validate_channel_valid ... ok
[INFO] [stdout] test mml::error::tests::display_empty_tie_chain ... ok
[INFO] [stdout] test mml::error::tests::display_invalid_number ... ok
[INFO] [stdout] test mml::error::tests::display_tie_pitch_mismatch ... ok
[INFO] [stdout] test mml::error::tests::display_invalid_tie_sequence ... ok
[INFO] [stdout] test mml::error::tests::display_invalid_tuplet_count ... ok
[INFO] [stdout] test mml::error::tests::display_tuplet_nest_too_deep ... ok
[INFO] [stdout] test mml::error::tests::display_tuplet_count_missing ... ok
[INFO] [stdout] test mml::error::tests::display_unclosed_tuplet ... ok
[INFO] [stdout] test mml::error::tests::display_unexpected_character ... ok
[INFO] [stdout] test mml::error::tests::display_unexpected_eof ... ok
[INFO] [stdout] test mml::error::tests::display_unexpected_token ... ok
[INFO] [stdout] test mml::file::tests::test_read_mml_file_complex_content ... ok
[INFO] [stdout] test mml::file::tests::test_read_mml_file_hash_in_middle_of_line ... ok
[INFO] [stdout] test mml::file::tests::test_read_mml_file_empty ... ok
[INFO] [stdout] test mml::file::tests::test_read_mml_file_invalid_extension ... ok
[INFO] [stdout] test mml::file::tests::test_read_mml_file_no_extension ... ok
[INFO] [stdout] test mml::file::tests::test_read_mml_file_not_found ... ok
[INFO] [stdout] test mml::file::tests::test_read_mml_file_multiline ... ok
[INFO] [stdout] test mml::file::tests::test_read_mml_file_only_hash ... ok
[INFO] [stdout] test midi::device::tests::test_list_midi_devices_info_returns_result ... ok
[INFO] [stdout] test mml::file::tests::test_read_mml_file_only_whitespace_lines ... ok
[INFO] [stdout] test db::tests::test_save_validation_volume_out_of_range ... ok
[INFO] [stdout] test mml::file::tests::test_read_mml_file_single_line ... ok
[INFO] [stdout] test mml::file::tests::test_read_mml_file_utf8 ... ok
[INFO] [stdout] test mml::file::tests::test_read_mml_file_removes_empty_lines ... ok
[INFO] [stdout] test cli::handlers::tests::test_play_handler_with_mml_device_fail_is_ok ... ok
[INFO] [stdout] test mml::file::tests::test_read_mml_file_with_comments ... ok
[INFO] [stdout] test mml::file::tests::test_read_mml_file_with_indented_comment ... ok
[INFO] [stderr] ALSA lib seq_hw.c:528:(snd_seq_hw_open) open /dev/snd/seq failed: No such file or directory
[INFO] [stderr] ALSA lib confmisc.c:855:(parse_card) cannot find card '0'
[INFO] [stderr] ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_card_inum returned error: No such file or directory
[INFO] [stderr] ALSA lib confmisc.c:422:(snd_func_concat) error evaluating strings
[INFO] [stderr] ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory
[INFO] [stderr] ALSA lib confmisc.c:1342:(snd_func_refer) error evaluating name
[INFO] [stderr] ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory
[INFO] [stderr] ALSA lib conf.c:5727:(snd_config_expand) Evaluate error: No such file or directory
[INFO] [stderr] ALSA lib pcm.c:2721:(snd_pcm_open_noupdate) Unknown PCM default
[INFO] [stdout] test mml::file::tests::test_read_mml_file_success ... ok
[INFO] [stdout] test mml::file::tests::test_read_mml_file_trim_whitespace ... ok
[INFO] [stdout] test cli::handlers::tests::test_export_logic_success ... ok
[INFO] [stdout] test cli::output::tests::test_display_midi_progress_short_duration ... ok
[INFO] [stdout] test mml::file::tests::test_read_mml_file_too_large ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 115 passed; 0 failed; 2 ignored; 0 measured; 0 filtered out; finished in 0.51s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/sine_mml-e2f71f212235b69d)
[INFO] [stderr]      Running tests/audio_synthesizer_test.rs (/opt/rustwide/target/debug/deps/audio_synthesizer_test-336e4eb41939ad01)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 43 tests
[INFO] [stdout] test test_beat_interval_16beat_at_120bpm ... ok
[INFO] [stdout] test test_beat_interval_4beat_at_120bpm ... ok
[INFO] [stdout] test test_beat_interval_4beat_at_240bpm ... ok
[INFO] [stdout] test test_beat_interval_8beat_at_120bpm ... ok
[INFO] [stdout] test test_get_tempo_events_default_tempo ... ok
[INFO] [stdout] test test_click_generation ... ok
[INFO] [stdout] test test_get_tempo_events_single_tempo ... ok
[INFO] [stdout] test test_mix_metronome_additive ... ok
[INFO] [stdout] test test_mix_metronome_with_tempo_events_empty ... ok
[INFO] [stdout] test test_mix_metronome_click_positions ... ok
[INFO] [stdout] test test_get_tempo_events_tempo_change_mid_song ... ok
[INFO] [stdout] test test_default_velocity_is_10 ... ok
[INFO] [stdout] test test_mix_metronome_with_tempo_events_single ... ok
[INFO] [stdout] test test_normalize_boundary_case ... ok
[INFO] [stdout] test test_noise_click_volume_application ... ok
[INFO] [stdout] test test_normalize_all_zeros ... ok
[INFO] [stdout] test test_noise_click_sample_count ... ok
[INFO] [stdout] test test_normalize_empty_slice ... ok
[INFO] [stdout] test test_normalize_negative_peak ... ok
[INFO] [stdout] test test_mix_metronome_with_tempo_events_tempo_change ... ok
[INFO] [stdout] test test_normalize_no_change_when_within_range ... ok
[INFO] [stdout] test test_mix_metronome_16beat_more_clicks ... ok
[INFO] [stdout] test test_normalize_single_sample_exceeding ... ok
[INFO] [stdout] test test_resample_linear_target_zero ... ok
[INFO] [stdout] test test_normalize_single_sample_within_range ... ok
[INFO] [stdout] test test_resample_linear_downsample ... ok
[INFO] [stdout] test test_resample_linear_upsample ... ok
[INFO] [stdout] test test_normalize_clipping_samples ... ok
[INFO] [stdout] test test_beat_interval_4beat_at_60bpm ... ok
[INFO] [stdout] test test_resample_linear_empty ... ok
[INFO] [stdout] test test_synthesize_tuplet_with_rest ... ok
[INFO] [stdout] test test_synthesize_simple_note ... ok
[INFO] [stdout] test test_synthesize_volume_clamp_lower ... ok
[INFO] [stdout] test test_synthesize_tuplet_5_notes ... ok
[INFO] [stdout] test test_synthesize_with_absolute_volume ... ok
[INFO] [stdout] test test_synthesize_tuplet_3_notes ... ok
[INFO] [stdout] test test_synthesizer_creation ... ok
[INFO] [stdout] test test_synthesize_volume_clamp_upper ... ok
[INFO] [stdout] test test_synthesize_with_relative_volume_decrease ... ok
[INFO] [stdout] test test_synthesize_with_relative_volume_increase ... ok
[INFO] [stdout] test test_synthesize_tuplet_with_base_duration ... ok
[INFO] [stdout] test test_synthesize_tuplet_octave_change ... ok
[INFO] [stdout] test test_beat_interval_invalid_beat - should panic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 43 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.17s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/cli_args_test.rs (/opt/rustwide/target/debug/deps/cli_args_test-60e976b262c28c2f)
[INFO] [stdout] 
[INFO] [stdout] running 28 tests
[INFO] [stdout] test test_midi_channel_default ... ok
[INFO] [stdout] test test_default_values ... ok
[INFO] [stdout] test test_bpm_option_removed ... ok
[INFO] [stdout] test test_midi_combined_options ... ok
[INFO] [stdout] test test_file_option_parsing ... ok
[INFO] [stdout] test test_metronome_volume_valid ... ok
[INFO] [stdout] test test_midi_channel_invalid ... ok
[INFO] [stdout] test test_metronome_beat_invalid ... ok
[INFO] [stdout] test test_midi_out_with_device_name ... ok
[INFO] [stdout] test test_no_history_default ... ok
[INFO] [stdout] test test_no_history_long_flag ... ok
[INFO] [stdout] test test_midi_list_subcommand ... ok
[INFO] [stdout] test test_no_history_short_flag ... ok
[INFO] [stdout] test test_midi_out_option ... ok
[INFO] [stdout] test test_no_history_with_loop_play ... ok
[INFO] [stdout] test test_validate_note_char_count ... ok
[INFO] [stderr]      Running tests/cli_integration.rs (/opt/rustwide/target/debug/deps/cli_integration-084271e24a378cb4)
[INFO] [stdout] test test_validate_note_too_long ... ok
[INFO] [stdout] test test_note_option ... ok
[INFO] [stdout] test test_metronome_beat_valid ... ok
[INFO] [stdout] test test_no_history_with_midi_out ... ok
[INFO] [stdout] test test_validate_note_utf8 ... ok
[INFO] [stdout] test test_validate_note_valid ... ok
[INFO] [stdout] test test_validate_volume_invalid ... ok
[INFO] [stdout] test test_validate_volume_valid ... ok
[INFO] [stdout] test test_waveform_parsing ... ok
[INFO] [stdout] test test_metronome_volume_out_of_range ... ok
[INFO] [stdout] test test_play_args_conflict ... ok
[INFO] [stdout] test test_midi_channel_valid ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 28 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 34 tests
[INFO] [stdout] test test_bpm_option_removed ... ok
[INFO] [stdout] test test_clear_history_invalid_input ... ok
[INFO] [stdout] test test_clear_history_cancel ... ok
[INFO] [stdout] test test_clear_history_runs ... ok
[INFO] [stdout] test test_cli_default_volume_relative ... ok
[INFO] [stdout] test test_cli_file_not_found ... ok
[INFO] [stdout] test test_cli_loop_count_error ... ok
[INFO] [stdout] test test_cli_invalid_extension ... ok
[INFO] [stdout] test test_cli_loop_nest_too_deep_error ... ok
[INFO] [stdout] test test_clear_history_confirm_yes ... ok
[INFO] [stdout] test test_cli_loop_expanded_too_large ... ok
[INFO] [stdout] test test_cli_history_displays_note_column ... FAILED
[INFO] [stdout] test test_cli_file_with_note ... ok
[INFO] [stdout] test test_cli_integration_file_volume_loop ... ok
[INFO] [stdout] test test_cli_note_too_long ... ok
[INFO] [stdout] test test_cli_nested_loop_5_levels ... ok
[INFO] [stdout] test test_cli_lowercase_with_loop ... ok
[INFO] [stdout] test test_cli_loop_with_escape_point ... ok
[INFO] [stdout] test test_cli_play_with_loop_syntax ... ok
[INFO] [stdout] test test_cli_volume_out_of_range ... ok
[INFO] [stdout] test test_help_flag ... ok
[INFO] [stdout] test test_cli_play_with_file ... ok
[INFO] [stdout] test test_history_command ... ok
[INFO] [stdout] test test_invalid_metronome_beat ... ok
[INFO] [stdout] test test_play_missing_input ... ok
[INFO] [stdout] test test_cli_note_with_utf8 ... ok
[INFO] [stdout] test test_cli_nested_loop_allowed ... ok
[INFO] [stdout] test test_cli_relative_volume ... ok
[INFO] [stdout] test test_cli_nested_loop_2_levels ... ok
[INFO] [stdout] test test_cli_play_with_note ... ok
[INFO] [stdout] test test_version_flag ... ok
[INFO] [stdout] test test_play_basic_mml ... ok
[INFO] [stdout] test test_valid_metronome_options ... ok
[INFO] [stdout] test test_cli_nested_loop_6_levels_error ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- test_cli_history_displays_note_column stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'test_cli_history_displays_note_column' (238) panicked at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/ops/function.rs:250:5:
[INFO] [stdout] Unexpected stdout, failed var.contains(Note)
[INFO] [stdout] ├── var: 履歴がありません
[INFO] [stdout] └── var as str: 履歴がありません
[INFO] [stdout] 
[INFO] [stdout] command=`"/opt/rustwide/target/debug/sine-mml" "history"`
[INFO] [stdout] code=0
[INFO] [stdout] stdout="履歴がありません\n"
[INFO] [stdout] stderr=""
[INFO] [stdout] 
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5a17971ad41a - std[ec4e911328de40a6]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5a17971ad41a - std[ec4e911328de40a6]::backtrace_rs::backtrace::trace_unsynchronized::<std[ec4e911328de40a6]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5a17971ad41a - std[ec4e911328de40a6]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5a17971ad41a - <<std[ec4e911328de40a6]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a96a4c5c53f902a3]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5a17971c322a - <core[a96a4c5c53f902a3]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5a17971c322a - core[a96a4c5c53f902a3]::fmt::write
[INFO] [stdout]    6:     0x5a17971b3162 - std[ec4e911328de40a6]::io::default_write_fmt::<alloc[c960326de6de28e6]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x5a17971b3162 - <alloc[c960326de6de28e6]::vec::Vec<u8> as std[ec4e911328de40a6]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x5a179718ac8f - <std[ec4e911328de40a6]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5a179718ac8f - std[ec4e911328de40a6]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5a17971a5329 - std[ec4e911328de40a6]::panicking::default_hook
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5a17970f505c - <alloc[c960326de6de28e6]::boxed::Box<dyn for<'a, 'b> core[a96a4c5c53f902a3]::ops::function::Fn<(&'a std[ec4e911328de40a6]::panic::PanicHookInfo<'b>,), Output = ()> + core[a96a4c5c53f902a3]::marker::Sync + core[a96a4c5c53f902a3]::marker::Send> as core[a96a4c5c53f902a3]::ops::function::Fn<(&std[ec4e911328de40a6]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   12:     0x5a17970f505c - test[30c43f9fd66fea90]::test_main_with_exit_callback::<test[30c43f9fd66fea90]::test_main::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x5a17971a54e2 - <alloc[c960326de6de28e6]::boxed::Box<dyn for<'a, 'b> core[a96a4c5c53f902a3]::ops::function::Fn<(&'a std[ec4e911328de40a6]::panic::PanicHookInfo<'b>,), Output = ()> + core[a96a4c5c53f902a3]::marker::Sync + core[a96a4c5c53f902a3]::marker::Send> as core[a96a4c5c53f902a3]::ops::function::Fn<(&std[ec4e911328de40a6]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   14:     0x5a17971a54e2 - std[ec4e911328de40a6]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5a179718ad48 - std[ec4e911328de40a6]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5a1797182609 - std[ec4e911328de40a6]::sys::backtrace::__rust_end_short_backtrace::<std[ec4e911328de40a6]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5a179718ba5d - __rustc[aa69f48705c57ca8]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5a17971c3a3c - core[a96a4c5c53f902a3]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5a17971333a8 - core[a96a4c5c53f902a3]::panicking::panic_display::<assert_cmd[7d43e6d150a4d46a]::assert::AssertError>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/panicking.rs:259:5
[INFO] [stdout]   20:     0x5a17971388d7 - <assert_cmd[7d43e6d150a4d46a]::assert::AssertError>::panic::<assert_cmd[7d43e6d150a4d46a]::assert::Assert>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/assert_cmd-2.1.2/src/assert.rs:1036:9
[INFO] [stdout]   21:     0x5a179713ddb7 - <<assert_cmd[7d43e6d150a4d46a]::assert::AssertError>::panic<assert_cmd[7d43e6d150a4d46a]::assert::Assert> as core[a96a4c5c53f902a3]::ops::function::FnOnce<(assert_cmd[7d43e6d150a4d46a]::assert::AssertError,)>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   22:     0x5a179713e194 - <core[a96a4c5c53f902a3]::result::Result<assert_cmd[7d43e6d150a4d46a]::assert::Assert, assert_cmd[7d43e6d150a4d46a]::assert::AssertError>>::unwrap_or_else::<<assert_cmd[7d43e6d150a4d46a]::assert::AssertError>::panic<assert_cmd[7d43e6d150a4d46a]::assert::Assert>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/result.rs:1622:23
[INFO] [stdout]   23:     0x5a17970dded4 - <assert_cmd[7d43e6d150a4d46a]::assert::Assert>::stdout::<predicates[a018198b558f0e78]::str::basics::ContainsPredicate, assert_cmd[7d43e6d150a4d46a]::assert::StrOutputPredicate<predicates[a018198b558f0e78]::str::basics::ContainsPredicate>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/assert_cmd-2.1.2/src/assert.rs:367:31
[INFO] [stdout]   24:     0x5a17970e4765 - cli_integration[32e22922b0df7791]::test_cli_history_displays_note_column
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/cli_integration.rs:268:10
[INFO] [stdout]   25:     0x5a17970df8a7 - cli_integration[32e22922b0df7791]::test_cli_history_displays_note_column::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/cli_integration.rs:254:43
[INFO] [stdout]   26:     0x5a17970e8fa6 - <cli_integration[32e22922b0df7791]::test_cli_history_displays_note_column::{closure#0} as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   27:     0x5a17970e913b - <fn() -> core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   28:     0x5a17970e913b - test[30c43f9fd66fea90]::__rust_begin_short_backtrace::<core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>, fn() -> core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:663:18
[INFO] [stdout]   29:     0x5a17970f5b2b - test[30c43f9fd66fea90]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:686:74
[INFO] [stdout]   30:     0x5a17970f5b2b - <core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   31:     0x5a17970f5b2b - std[ec4e911328de40a6]::panicking::catch_unwind::do_call::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}>, core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   32:     0x5a17970f5b2b - std[ec4e911328de40a6]::panicking::catch_unwind::<core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>, core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   33:     0x5a17970f5b2b - std[ec4e911328de40a6]::panic::catch_unwind::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}>, core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panic.rs:359:14
[INFO] [stdout]   34:     0x5a17970f5b2b - test[30c43f9fd66fea90]::run_test_in_process
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:686:27
[INFO] [stdout]   35:     0x5a17970f5b2b - test[30c43f9fd66fea90]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:607:43
[INFO] [stdout]   36:     0x5a17970f1244 - test[30c43f9fd66fea90]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:637:41
[INFO] [stdout]   37:     0x5a17970f1244 - std[ec4e911328de40a6]::sys::backtrace::__rust_begin_short_backtrace::<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   38:     0x5a17970f8732 - std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked::<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   39:     0x5a17970f8732 - <core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   40:     0x5a17970f8732 - std[ec4e911328de40a6]::panicking::catch_unwind::do_call::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   41:     0x5a17970f8732 - std[ec4e911328de40a6]::panicking::catch_unwind::<(), core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   42:     0x5a17970f8732 - std[ec4e911328de40a6]::panic::catch_unwind::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panic.rs:359:14
[INFO] [stdout]   43:     0x5a17970f8732 - std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked::<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   44:     0x5a17970f8732 - <std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1} as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   45:     0x5a17971accaf - <alloc[c960326de6de28e6]::boxed::Box<dyn core[a96a4c5c53f902a3]::ops::function::FnOnce<(), Output = ()> + core[a96a4c5c53f902a3]::marker::Send> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/alloc/src/boxed.rs:2240:9
[INFO] [stdout]   46:     0x5a17971accaf - <std[ec4e911328de40a6]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   47:     0x7704049faaa4 - <unknown>
[INFO] [stdout]   48:     0x770404a87a64 - clone
[INFO] [stdout]   49:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     test_cli_history_displays_note_column
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 33 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.21s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test cli_integration`
[INFO] running `Command { std: "docker" "inspect" "033c8b2cea85627071f29d16388767120333dc1a7c193696fea5c18fb2afdb6c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "033c8b2cea85627071f29d16388767120333dc1a7c193696fea5c18fb2afdb6c", kill_on_drop: false }`
[INFO] [stdout] 033c8b2cea85627071f29d16388767120333dc1a7c193696fea5c18fb2afdb6c
